[10/18 11:37:20,Telerik.TestStudio.exe(8496:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'test file upload dialog' selected.
[10/18 11:37:20,Telerik.TestStudio.exe(8496:1),Uncategorized] Orchestrator._iUIProject_TestSelected() : Test 'test file upload dialog' selected.
[10/18 11:37:20,ArtOfTest.Runner.exe(13760:3)] First trace message from process 13760: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=1492
[10/18 11:37:20,ArtOfTest.Runner.exe(13760:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 12504).
[10/18 11:37:20,ArtOfTest.Runner.exe(13760:3),Uncategorized] Loader.LoadTranslatorGroups() : ------- Function Start -------
[10/18 11:37:20,ArtOfTest.Runner.exe(13760:3),Uncategorized] Loader.LoadTranslatorGroups() : Translators Directory is : C:\Program Files (x86)\Telerik\Test Studio\Bin\Translators
[10/18 11:37:22,ArtOfTest.Runner.exe(13760:3),Uncategorized] Loader.LoadTranslatorGroups() : ------- Function End -------
[10/18 11:37:26,ArtOfTest.Runner.exe(13760:10)] First trace message from pool unnamed thread (managed ID = 10, native ID = 13756).
[10/18 11:37:26,ArtOfTest.Runner.exe(13760:10),Framework] BrowserHttpHost.HandleRequest() : Creating command queue for browser 71522101-d0fc-4ced-a849-589c6b6ea9d5
[10/18 11:37:26,ArtOfTest.Runner.exe(13760:10),Framework] Manager.AddNewBrowser() : Added client ID "71522101-d0fc-4ced-a849-589c6b6ea9d5" (now there is/are 1 total).
[10/18 11:37:31,ArtOfTest.Runner.exe(13760:6)] First trace message from pool unnamed thread (managed ID = 6, native ID = 12024).
[10/18 11:37:31,ArtOfTest.Runner.exe(13760:6),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[10/18 11:37:31,ArtOfTest.Runner.exe(13760:6),Uncategorized] ExtensionsManager.LoadExtensions() : Got 7 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[10/18 11:37:31,ArtOfTest.Runner.exe(13760:6),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[10/18 11:37:31,ArtOfTest.Runner.exe(13760:6),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[10/18 11:37:31,ArtOfTest.Runner.exe(13760:6),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'file:///C:/Users/awong/Desktop/test.html'"...
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:11)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 11, native ID = 12732).
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:11),UIAutomation] AutomationExtensions.AddStructureChangedEventHandler() : Added a structure change handler on element System.Windows.Automation.AutomationElement scope Subtree.
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'file:///C:/Users/awong/Desktop/test.html'".
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/18 11:37:32,ArtOfTest.Runner.exe(13760:6),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Wait for '6000' msec."...
[10/18 11:37:36,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (F1360).
[10/18 11:37:36,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (101360).
[10/18 11:37:36,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (111360).
[10/18 11:37:37,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (121360).
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:6),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Wait for '6000' msec.".
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "OLEChannelWnd", ClassName = "OleMainThreadWndClass").
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "OLEChannelWnd", ClassName = "OleMainThreadWndClass").
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "OLEChannelWnd", ClassName = "OleMainThreadWndClass").
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "OLEChannelWnd", ClassName = "OleMainThreadWndClass").
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "OLEChannelWnd", ClassName = "OleMainThreadWndClass").
[10/18 11:37:38,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "OLEChannelWnd", ClassName = "OleMainThreadWndClass").
[10/18 11:37:39,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (211332).
[10/18 11:37:39,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (F1328).
[10/18 11:37:39,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (1813E4).
[10/18 11:37:39,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (141318).
[10/18 11:37:39,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (BC1312).
[10/18 11:37:39,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (141310).
[10/18 11:37:39,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (1D12E6).
[10/18 11:37:43,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "", ClassName = "tooltips_class32").
[10/18 11:37:53,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (151318).
[10/18 11:37:53,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (1E12E6).
[10/18 11:37:53,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (101328).
[10/18 11:37:53,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (1913E4).
[10/18 11:37:53,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (151310).
[10/18 11:37:53,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (221332).
[10/18 11:37:53,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (1612D2).
[10/18 11:38:07,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "OLEChannelWnd", ClassName = "OleMainThreadWndClass").
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "Default IME", ClassName = "IME").
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected, but not considered a dialog (Text = "GDI+ Window", ClassName = "GDI+ Hook Window Class").
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Error] ExecutionEngine.ExecuteStep() : EXCEPTION! (see below)
     Outer Exception Type: ArtOfTest.Common.Design.Exceptions.ElementFindException
     Message: Unable to locate element. Details: Attempting to find [Html] element using
          Find logic
          (Html): [id 'Exact' fileUpload] AND [tagname 'Exact' input]
          Unable to locate element. Search failed!
          Learn more about finding elements here:
          http://www.telerik.com/automated-testing-tools/support/documentation/user-guide/troubleshooting_guide/test-execution-problems/unable-to-locate-element.aspx
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at ArtOfTest.WebAii.Design.Execution.ExecutionUtils.WaitForAllElements(IAutomationHost host, AutomationDescriptor descriptor, Int32 timeout)
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.ExecuteStep(Int32 order)

[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::Start
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::BreakOnFail
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.CheckPause() : VisualDebugger::CheckPause::End
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::ShutDown::Start
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::Start
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DetachWrappedWindow::End
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::DispatcherBeginInvokeShutdown::Start
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Uncategorized] AdornerWindow.ShutDown() : AdornerWnd::Close::Start
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1136095525" in order to unblock and eventually terminate it...
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:9)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 9, native ID = 10728).
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:9),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Framework] Manager.RemoveBrowser() : Removed client ID "71522101-d0fc-4ced-a849-589c6b6ea9d5" (0 remaining).
[10/18 11:38:08,ArtOfTest.Runner.exe(13760:6),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[10/18 11:38:09,ArtOfTest.Runner.exe(13760:11),DialogPlayback] DialogMonitor.OnWindowCreated() : New window detected but the HWND is invalid (C0628).
[10/18 11:38:09,ArtOfTest.Runner.exe(13760:6),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[10/18 11:38:09,ArtOfTest.Runner.exe(13760:6),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[10/18 11:38:09,Telerik.TestStudio.exe(8496:66)] First trace message from background thread "Callback Listener Thread" (managed ID = 66, native ID = 7776).
[10/18 11:38:09,Telerik.TestStudio.exe(8496:66),Warning] PipeCommunication.AsyncPipeRead() : Pipe was already disposed at the moment of the wait attempt.
